05-13 19:05:41 D/ModuleListener: ModuleListener.testStarted(com.google.android.rvis.tvts.TvtsRvisTest#testRvisLaunchHTT) on SKBFXYZO0DTQ
05-13 19:05:41 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, getprop, ro.build.version.sdk] with timeout: 45s
05-13 19:05:41 D/DeviceHostUtils: apiLevel: 31
05-13 19:05:41 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
05-13 19:05:42 D/RunUtil: Running command null without timeout.
05-13 19:05:42 D/AppRunnerUtils: Checking service: com.google.android.tv.remote.service/.RemoteService
05-13 19:05:42 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.tv.remote.service/.RemoteService
05-13 19:05:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, dumpsys, activity, service, com.google.android.tv.remote.service/.RemoteService] with timeout: 2m 0s
05-13 19:05:42 I/AppRunnerUtils: Service PID = 26052
05-13 19:05:42 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
05-13 19:05:42 D/DeviceHostUtils: apiLevel: 31
05-13 19:05:42 I/TvtsRvisTest: KEYCODE_TO_VP_SHOWN	KEYCODE_TO_READING (In Milliseconds)
05-13 19:05:42 D/RunUtil: Running command null without timeout.
05-13 19:05:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
05-13 19:05:42 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:42 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:43 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:43 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:43 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:43 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:43 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:43 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:43 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:43 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:44 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:44 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:44 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:44 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:44 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:44 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:45 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:45 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:45 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:45 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:45 E/TvtsRvisTest: Run 1/10 encountered exception: Didn't successfully read non-zero audio bytes!
05-13 19:05:45 I/TvtsRvisTest:               -1000	             -1000
05-13 19:05:45 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 19:05:45 D/RunUtil: Running command null without timeout.
05-13 19:05:45 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
05-13 19:05:46 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:46 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:46 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:46 I/TvtsRvisTest:                  28	               133
05-13 19:05:46 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 19:05:46 D/RunUtil: Running command null without timeout.
05-13 19:05:46 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
05-13 19:05:47 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:47 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:47 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:47 I/TvtsRvisTest:                  21	               274
05-13 19:05:47 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 19:05:47 D/RunUtil: Running command null without timeout.
05-13 19:05:47 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
05-13 19:05:48 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:48 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:48 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:48 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:48 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:48 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:48 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:48 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:49 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:49 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:49 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:49 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:49 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:49 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:49 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:50 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:50 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:50 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:50 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:50 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:51 E/TvtsRvisTest: Run 4/10 encountered exception: Didn't successfully read non-zero audio bytes!
05-13 19:05:51 I/TvtsRvisTest:               -1000	             -1000
05-13 19:05:51 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 19:05:51 D/RunUtil: Running command null without timeout.
05-13 19:05:51 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
05-13 19:05:51 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:51 I/TvtsRvisTest:                  13	                26
05-13 19:05:51 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 19:05:51 D/RunUtil: Running command null without timeout.
05-13 19:05:51 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
05-13 19:05:52 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:52 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:52 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:52 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:52 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:52 I/TvtsRvisTest:                  10	               478
05-13 19:05:52 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 19:05:53 D/RunUtil: Running command null without timeout.
05-13 19:05:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
05-13 19:05:53 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:53 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:53 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:53 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:53 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:53 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:54 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:54 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:54 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:54 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:54 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:54 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:54 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:55 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:55 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:55 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:55 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:55 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:55 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:55 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:56 E/TvtsRvisTest: Run 7/10 encountered exception: Didn't successfully read non-zero audio bytes!
05-13 19:05:56 I/TvtsRvisTest:               -1000	             -1000
05-13 19:05:56 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 19:05:56 D/RunUtil: Running command null without timeout.
05-13 19:05:56 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
05-13 19:05:56 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:56 I/TvtsRvisTest:                  13	                21
05-13 19:05:56 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 19:05:57 D/RunUtil: Running command null without timeout.
05-13 19:05:57 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
05-13 19:05:57 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:57 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:57 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:57 D/RunUtil: operation failed, waiting for 200 ms
05-13 19:05:57 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:58 I/TvtsRvisTest:                  19	               531
05-13 19:05:58 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 19:05:58 D/RunUtil: Running command null without timeout.
05-13 19:05:58 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
05-13 19:05:58 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
05-13 19:05:59 I/TvtsRvisTest:                  17	                93
05-13 19:05:59 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
05-13 19:05:59 I/TvtsRvisTest: numOfRuns: 10
05-13 19:05:59 I/TvtsRvisTest: numOfFailedRuns: 3
05-13 19:05:59 D/PrettyTestEventLogger: 
==================== com.google.android.rvis.tvts.TvtsRvisTest#testRvisLaunchHTT ENDED: Mon May 13 19:05:59 KST 2024 ====================
